View Issue Details

IDProjectCategoryView StatusLast Update
0001604T99X171.00 SKB EagleSW Issuepublic2025-04-07 10:00
Reporter(ALTech) Younkwang Jung Assigned To(SW) Kerwin Chen Due Date
PrioritynormalSeveritys4-minorReproducibilitysometimes
Status closedResolutionnot a bug 
Summary0001604: [Smart3][ATV10] audio volume level issue
DescriptionHi Kerwin

Issues that sometimes hear too little volume level are being reported in the field.
However, the VoC logs obtained did not have much information.

However, audio related issues were observed during testing at home. ( v15.534.28 )
In certain cases, the volume level suddenly became smaller. and Sometimes, it was observed that the audio volume level did not increase.

The log attached here is a log that does not increase the actual sound even if I press the volume up of the RCU.
(The volume level bar of the UI was increasing normally.)

But this issue has not been reproduced anymore after rebooting.
First of all, please check if there are any clues in the log.

Suspicions in the log are as follows:

01-15 17:04:37.593 D 4800 4800 KeyEventManager: processKeyHandle() notifyObservers call
01-15 17:04:37.593 D 4800 4800 KeyEventObservable: notifyObservers() NONE KEY_DOWN
01-15 17:04:37.593 D 4800 4800 KeyEventObservable: notifyObservers() 4 12
01-15 17:04:37.594 D 3845 8188 MediaSessionService: dispatchVolumeKeyEvent, pkg=com.skb.tv, opPkg=com.skb.tv, pid=4800, uid=1000, asSystem=true, event=KeyEvent { action=ACTION_DOWN, keyCode=KEYCODE_VOLUME_UP, scanCode=115, metaState=0, flags=0x8, repeatCount=10, eventTime=241304, downTime=239860, deviceId=6, source=0x701, displayId=-1 }, stream=-2147483648, musicOnly=false
01-15 17:04:37.598 I 4002 4257 vol.Events: writeEvent level_changed STREAM_MUSIC 31
01-15 17:04:37.600 I 4002 4257 vol.Events: writeEvent key STREAM_MUSIC 31
01-15 17:04:37.600 I 3845 3940 WindowManager: interceptKeyTi keyCode=24 down=true repeatCount=11 keyguardOn=false canceled=false policyFlags=1107296256 mDeviceId=6 mSource=1793 mScanCode=115 mCharacters=null
01-15 17:04:37.601 D 3845 3940 WindowManager: interceptKeyTi strDate 2023-01-15 17:04
01-15 17:04:37.602 D 3845 3940 WindowManager: interceptSkbFuctionKeyBeforeDispatching keycode = 24
01-15 17:04:37.602 D 3845 3940 WindowManager: keycode : 24 - processed. by skb function key
01-15 17:04:37.603 D 3845 3940 AS.AudioService: adjustSuggestedStreamVolume() stream=-2147483648, flags=4101, caller=WindowManager, volControlStream=3, userSelect=false
01-15 17:04:37.603 D 3845 3940 AS.AudioService: getActiveStreamType: suggestedStreamType = -2147483648
01-15 17:04:37.605 D 3845 3940 AS.AudioService: getActiveStreamType: suggestedStreamType: AudioManager.USE_DEFAULT_STREAM_TYPE return DEFAULT_VOL_STREAM_NO_PLAYBACK
01-15 17:04:37.606 D 3845 3940 AS.AudioService: adjustStreamVolume() stream=3, dir=1, flags=4097, caller=WindowManager
01-15 17:04:37.606 D 3845 3940 AS.AudioService: getActiveStreamType: suggestedStreamType = 3
01-15 17:04:37.607 D 3845 3940 AS.AudioService: getActiveStreamType: suggestedStreamType: AudioManager.USE_DEFAULT_STREAM_TYPE return DEFAULT_VOL_STREAM_NO_PLAYBACK
01-15 17:04:37.609 D 3845 3940 AS.AudioService: adjustStreamVolume mIsDDS :false(flags & AudioManager.FLAG_FROM_KEY) : 4096(device & AudioSystem.DEVICE_OUT_ALL_A2DP 0
01-15 17:04:37.609 D 3845 3940 AS.AudioService: adjustStreamVolume direction = 1
01-15 17:04:37.609 D 3845 3940 AS.AudioService: adjustStreamVolume device = 2
01-15 17:04:37.610 I 4002 4002 vol.Events: writeEvent show_dialog volume_changed keyguard=false
01-15 17:04:37.611 D 3845 3940 AS.AudioService: streamType = 3, device = speaker called : WindowManager
01-15 17:04:37.611 D 3845 3940 AS.AudioService: passthroughToTv true hdmiCecEnabled true isMuteAdjust false passthroughEnableStr false supportDolbyMS12 false
01-15 17:04:37.612 D 3845 3940 AS.AudioService: passthroughToTv Not in passthrough audio channel return
01-15 17:04:37.614 D 3845 5344 AS.AudioService: Volume controller visible: true
01-15 17:04:37.660 I 5853 6250 chromium: [5853:6250:INFO:volume_control_android.cc(219)] SetVolumeOnThread: level=1 (0)
01-15 17:04:37.660 I 5853 6250 chromium: [5853:6250:INFO:audio_sink_manager.cc(67)] SetTypeVolumeDb: level_db=0 type=MEDIA
01-15 17:04:37.690 D 3845 3940 VolumeController: salmon postVolumeChanged streamType = 3
01-15 17:04:37.690 W 3845 3940 InputDispatcher: Excessive delay in interceptKeyBeforeDispatching; took 91 ms
01-15 17:04:37.691 I 4208 4208 BTV_IMEService: ime:onKeyDown start keyCode = 24, packageName: com.skb.tv
01-15 17:04:37.692 W 4208 4208 BTV_IME-InputAttributes: isAllowApp mInputType :0, TYPE_CLASS_TEXT : 1, package : com.skb.tv
01-15 17:04:37.692 I 4208 4208 BTV_IMEService: ime:onKeyDown keyCode = 24, KeyIndex = 17, KeySets().length = 10, isWebInputType = false, isEditable = false, isTypeNull = true, currentKeyMode = 0, isSearchMode = false, fieldId = 2131166785, inputType = 0, isGeneralTextInput = false, isInputViewShown = false, isAllowApp = false
...
01-15 17:04:42.652 I 3518 3600 audio_hw_primary: out_set_volume(), stream(0xef951380), left:0.000000 right:0.000000
01-15 17:04:42.773 D 3560 3560 APM_AudioPolicyManager: setVolumeCurveIndex: wrong index 0 min=1 max=7
01-15 17:04:42.773 E 3560 3560 APM_AudioPolicyManager: setVolumeIndexForAttributes failed to set curve index for group 2 device 0x2
01-15 17:04:42.773 E 3845 3961 AudioSystem-JNI: Command failed for android_media_AudioSystem_setStreamVolumeIndex: -22
01-15 17:04:42.773 D 3560 3560 APM_AudioPolicyManager: setVolumeCurveIndex: wrong index 0 min=1 max=7

Thank you
YK.Jung
TagsNo tags attached.
Attach Tags

Users monitoring this issue

User List (ALTech) JunGyu Kim , (ALTech) SY Yoon

Activities

(ALTech) Younkwang Jung

2023-01-15 17:18

developer  

logcat.zip (1,356,996 bytes)

(SW) Kerwin Chen

2023-01-16 10:02

developer   ~0011785

Hi YK,

BTV volume controls are handled by MW and UI.
From log you attached, I see there is a 'mute' key.
Then SPTEK pass 'index 0' to 'setStreamVolumeIndex' in Frameworks.
Please check log below:

=================================================================================================================
01-15 17:04:42.570 3845 3941 D WindowManager: interceptKeyTq keycode=164 interactive=true keyguardActive=false policyFlags=22000000
01-15 17:04:42.570 3845 3941 D WindowManager: interceptKeyTq keycode= 164 isValidGlobalKey
01-15 17:04:42.571 3845 3940 I WindowManager: interceptKeyTi keyCode=164 down=false repeatCount=0 keyguardOn=false canceled=false policyFlags=1644167168 mDeviceId=6 mSource=1793 mScanCode=113 mCharacters=null
01-15 17:04:42.572 3845 3940 D WindowManager: interceptSkbFuctionKeyBeforeDispatching keycode = 164
01-15 17:04:42.572 3845 3940 D WindowManager: keycode : 164 - processed. by skb function key
01-15 17:04:42.572 3845 3940 W GlobalKeyManager: handleGlobalKey keyCode : 164 componentComponentInfo{com.skb.tv/com.skb.google.tv.global.STBGlobalkeyBroadCastReceiver}
01-15 17:04:42.572 6675 6695 D UEI.BLE : HID Consumer Key report notification val = FF00, KeyCode = UNKNOWN
01-15 17:04:42.573 5120 5120 D BtvService6.2.79 IBtvService: BTF|onReceive|75|IN| DeviceCtrlServiceReceiver::onReceive action = com.skb.intent.KEY_LED_BLINK, BUILD_DATE:2022.07.15
01-15 17:04:42.574 5120 5120 D BtvService6.2.79 IBtvService: BTF|onReceive|494| DeviceCtrlServiceReceiver::onReceive : call getFrontPanel().setLedState, lastKeyTime= 2023-01-15 17:04
01-15 17:04:42.574 5120 5120 I BtvJni[6]22.06.08: BTF|setLastKeyTime|1317|setLastKeyTime keyTime: 2023-01-15 17:04
01-15 17:04:42.574 5120 5120 D BTFSerJni[6][22.06.08]: tvcoreJni_setLastKeyTime start
01-15 17:04:42.574 5120 5120 D BTFSerJni[6][22.06.08]: [tvcoreJni_setLastKeyTime] keyTime(2023-01-15 17:04)
01-15 17:04:42.575 3589 3781 I TVService-22.06.08: [vendor/skb/framework/btf_dev/interface/btvservice/1.0/default/TVService.cpp][setLastKeyTime] called keyTime=(2023-01-15 17:04)
01-15 17:04:42.575 3589 3781 I TVService-22.06.08: [TVService::setLastKeyTime] cKeyTime(2023-01-15 17:04)
01-15 17:04:42.575 6675 6725 D UEI.SmartControl: File event:8
01-15 17:04:42.576 5120 5120 W BtvJni[6]22.06.08: frontPanelSetLedState index: 3, state: 2, StateQ.size(): 0, BQ.remainingCapa(): 10
01-15 17:04:42.576 5120 5120 D BtvService6.2.79 IBtvService: BTF|onReceive|510|OUT| DeviceCtrlServiceReceiver:: END
01-15 17:04:42.576 5120 5357 W BtvJni[6]22.06.08: frontPanelSetLedState take after
01-15 17:04:42.576 5120 5357 D BTFSerJni[6][22.06.08]: tvcoreJni_frontPanelSetLedState start
01-15 17:04:42.577 3589 3781 I TVService-22.06.08: [TVService::frontPanelSetLedState] index(3) state(2).
01-15 17:04:42.577 3589 3781 I FrontPanelManager: [frontPanelSetLedState] called index(3) state(2)
01-15 17:04:42.582 4800 5405 I BtvKeyEvent[6]-2022.06.23: checkBtvKeyCode outKeyCode : 164, name : 164
01-15 17:04:42.582 4800 5405 D mUHDSTBGlobalkeyBroadCastReceiver: onReceive() keyCode : 164, action : KeyEvent.ACTION_UP
01-15 17:04:42.583 4800 4800 D KeyEventManager: onKeyUp : 164, isLongKey : false
01-15 17:04:42.583 4800 4800 D KeyEventManager: onKeyUp : MUTE
01-15 17:04:42.583 4800 4800 D KeyEventManager: processKeyHandle() notifyObservers call
01-15 17:04:42.583 4800 4800 D KeyEventObservable: notifyObservers() MUTE KEY_UP
01-15 17:04:42.584 4800 4800 I MainActivity: mKeyEventObserver.onKeyEvent() keyEventType : MUTE, keyEventAction : KEY_UP, isAlreadyProcess : false
01-15 17:04:42.584 4800 4800 I STBGlobal: getIsRemoteUpdating() called : false
01-15 17:04:42.584 4800 4800 D MainActivity: sendAudioScreenDisable | null
01-15 17:04:42.584 4800 4800 D Contextual: sendGlobalKey | SMART_3_STB | up | mute
01-15 17:04:42.584 4800 4800 D Contextual: sendGlobalKey | {"from":"com.skb.tv","command":"global_button","value":{"action":"up","key":"mute"}}
01-15 17:04:42.584 4800 4800 D Contextual: mIsLimitStart : false, mIsTempLimitStart : false
01-15 17:04:42.584 4800 4800 D STBAPIManager: getKidsZoneEntry called
..
01-15 17:04:42.599 4800 4800 I AVControlManager: BTF|isStreamMute|390|IN| AudioMixer::isStreamMute mContext : com.skb.google.tv.global.STBGlobal@4bb43f2
01-15 17:04:42.601 4800 4800 I AVControlManager: BTF|isStreamMute|395|OUT| AudioMixer::isStreamMute return : false
01-15 17:04:42.601 4800 4800 I TVSManager: isStreamMute() false
01-15 17:04:42.601 4800 4800 I TVSManager: setStreamMute() true
01-15 17:04:42.602 4800 4800 I AVControlManager: BTF|setStreamMute|406|IN| AudioMixer::setStreamMute isMute : true, flag : 1 mContext : com.skb.google.tv.global.STBGlobal@4bb43f2
01-15 17:04:42.602 4800 4828 D NuguDelegationSession(2.1.1): isServiceConnected = true
01-15 17:04:42.602 4800 4828 D NuguDelegationStatus(2.1.1): [getStatus] status :
01-15 17:04:42.602 4800 4828 D NuguDelegationSession(2.1.1): [provideStatus] status :
01-15 17:04:42.603 3845 4098 D AS.AudioService: adjustStreamVolume() stream=3, dir=-100, flags=1, caller=com.skb.tv
01-15 17:04:42.603 3845 4098 D AS.AudioService: getActiveStreamType: suggestedStreamType = 3
01-15 17:04:42.605 3845 4098 D AS.AudioService: getActiveStreamType: suggestedStreamType: AudioManager.USE_DEFAULT_STREAM_TYPE return DEFAULT_VOL_STREAM_NO_PLAYBACK
01-15 17:04:42.606 3845 4098 D AS.AudioService: adjustStreamVolume mIsDDS :false(flags & AudioManager.FLAG_FROM_KEY) : 0(device & AudioSystem.DEVICE_OUT_ALL_A2DP): 0
01-15 17:04:42.606 3845 4098 D AS.AudioService: adjustStreamVolume direction = -100
01-15 17:04:42.606 3845 4098 D AS.AudioService: adjustStreamVolume device = 2
01-15 17:04:42.606 3845 4098 D AS.AudioService: streamType = 3, device = speaker called : com.skb.tv
01-15 17:04:42.607 3845 4098 D AS.AudioService: passthroughToTv true hdmiCecEnabled true isMuteAdjust true passthroughEnableStr false supportDolbyMS12 false
01-15 17:04:42.607 3845 4098 D AS.AudioService: passthroughToTv Not in passthrough audio channel return
01-15 17:04:42.629 4002 4257 I vol.Events: writeEvent mute_changed STREAM_MUSIC true
...
01-15 17:04:42.644 3560 3963 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream: 3 index : 0 device: 128 :
01-15 17:04:42.644 3560 3963 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume mExtMute: 0, mExtCC: 0
01-15 17:04:42.644 3560 3963 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream : 3 volume_index : 0 gain : -758.000000
01-15 17:04:42.647 3589 3782 D BTV_HAL_MGR.606: [useSpeakerVolumePath] useSpeakerVolumePath : false
01-15 17:04:42.647 3589 3782 E AudioMixer: BTF|AMIXER_SetAudioVolume|74|IN| amixer=eb6ac050 volume=0 gain=-758.000000
01-15 17:04:42.648 3589 3782 E AudioMixer: BTF|setMasterAudioVolumeIntoFile|226|IN| save volume:0.000000 ,volumeIndex : 0
01-15 17:04:42.648 3589 3782 E halMediaPlayer: BTF|setAudioVolume|787|IN| volume:0.000000
01-15 17:04:42.648 3589 3782 E halMediaPlayer: BTF|player_SetAudioVolume|737|IN| player=eca03210 volume=0.000000
01-15 17:04:42.648 3589 3782 D AmlHalPlayerImpl_0: SetVolume[0] volume = 0.000000
01-15 17:04:42.648 3589 3782 I LivePlayer_0: [setVolume:699] setVolume: 0.00
01-15 17:04:42.648 3589 3782 E halMediaPlayer: BTF|player_SetAudioVolume|747|OUT|
01-15 17:04:42.648 3589 3782 E halMediaPlayer: BTF|setAudioVolume|795| error setting! player:0, i:1, mState:0
01-15 17:04:42.648 3589 3782 E halMediaPlayer: BTF|setAudioVolume|800|OUT|
01-15 17:04:42.648 3589 3782 E AudioMixer: BTF|AMIXER_SetAudioVolume|86|OUT|vol:0.000000
01-15 17:04:42.648 3560 3963 D BtvMediaVolumeControlLib: status : 0
01-15 17:04:42.648 3518 3600 I audio_hw_primary: out_set_volume(), stream(0xef951380), left:0.000000 right:0.000000
01-15 17:04:42.650 3560 3963 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream: 3 index : 0 device: 1024 :
01-15 17:04:42.650 3560 3963 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume mExtMute: 0, mExtCC: 0
01-15 17:04:42.650 3560 3963 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream : 3 volume_index : 0 gain : -758.000000
01-15 17:04:42.650 3589 3782 D BTV_HAL_MGR.606: [useSpeakerVolumePath] useSpeakerVolumePath : false
01-15 17:04:42.650 3589 3782 E AudioMixer: BTF|AMIXER_SetAudioVolume|74|IN| amixer=eb6ac050 volume=0 gain=-758.000000
01-15 17:04:42.651 3589 3782 E AudioMixer: BTF|setMasterAudioVolumeIntoFile|226|IN| save volume:0.000000 ,volumeIndex : 0
01-15 17:04:42.651 3589 3782 E halMediaPlayer: BTF|setAudioVolume|787|IN| volume:0.000000
01-15 17:04:42.651 3589 3782 E halMediaPlayer: BTF|player_SetAudioVolume|737|IN| player=eca03210 volume=0.000000
01-15 17:04:42.651 3589 3782 D AmlHalPlayerImpl_0: SetVolume[0] volume = 0.000000
01-15 17:04:42.651 3589 3782 I LivePlayer_0: [setVolume:699] setVolume: 0.00
01-15 17:04:42.651 3589 3782 E halMediaPlayer: BTF|player_SetAudioVolume|747|OUT|
01-15 17:04:42.651 3589 3782 E halMediaPlayer: BTF|setAudioVolume|795| error setting! player:0, i:1, mState:0
01-15 17:04:42.651 3589 3782 E halMediaPlayer: BTF|setAudioVolume|800|OUT|
01-15 17:04:42.652 3589 3782 E AudioMixer: BTF|AMIXER_SetAudioVolume|86|OUT|vol:0.000000
01-15 17:04:42.652 3560 3963 D BtvMediaVolumeControlLib: status : 0
01-15 17:04:42.652 3518 3600 I audio_hw_primary: out_set_volume(), stream(0xef951380), left:0.000000 right:0.000000
01-15 17:04:42.654 3845 5347 E WindowManager: App trying to use insecure INPUT_FEATURE_NO_INPUT_CHANNEL flag. Ignoring
...
01-15 17:04:42.773 3560 3560 D APM_AudioPolicyManager: setVolumeCurveIndex: wrong index 0 min=1 max=7
01-15 17:04:42.773 3560 3560 E APM_AudioPolicyManager: setVolumeIndexForAttributes failed to set curve index for group 2 device 0x2
01-15 17:04:42.773 3845 3961 E AudioSystem-JNI: Command failed for android_media_AudioSystem_setStreamVolumeIndex: -22

(ALTech) Younkwang Jung

2023-02-06 19:30

developer   ~0012033

Hi Kerwin

This issue has been reported.
https://jira.skbroadband.com/browse/BFXBTF-489

STREAM_MUSIC device was set to speaker instead of hdmi.

When/How can this be set to "device = speaker"?

If I look at the attached log, it was set to speaker.
===============================================================
..
01-15 17:04:42.606 3845 4098 D AS.AudioService: adjustStreamVolume device = 2
01-15 17:04:42.606 3845 4098 D AS.AudioService: streamType = 3, device = speaker called : com.skb.tv <== device is speaker
01-15 17:04:42.607 3845 4098 D AS.AudioService: passthroughToTv true hdmiCecEnabled true isMuteAdjust true passthroughEnableStr false supportDolbyMS12 false
...
===============================================================

Thank you
YK.Jung

(SW) Kerwin Chen

2025-04-07 10:00

developer   ~0017418

no activity for this issue

Issue History

Date Modified Username Field Change
2023-01-15 17:18 (ALTech) Younkwang Jung New Issue
2023-01-15 17:18 (ALTech) Younkwang Jung Status new => assigned
2023-01-15 17:18 (ALTech) Younkwang Jung Assigned To => (SW) Kerwin Chen
2023-01-15 17:18 (ALTech) Younkwang Jung File Added: logcat.zip
2023-01-15 17:18 (ALTech) Younkwang Jung Summary [Smart3][ATV10] audio volume => [Smart3][ATV10] audio volume level issue
2023-01-15 17:19 (ALTech) Younkwang Jung Issue Monitored: (ALTech) SY Yoon
2023-01-15 17:19 (ALTech) Younkwang Jung Issue Monitored: (ALTech) JunGyu Kim
2023-01-16 10:02 (SW) Kerwin Chen Note Added: 0011785
2023-01-16 10:04 (SW) Kerwin Chen Assigned To (SW) Kerwin Chen => (ALTech) Younkwang Jung
2023-01-16 10:04 (SW) Kerwin Chen Status assigned => acknowledged
2023-02-06 19:30 (ALTech) Younkwang Jung Note Added: 0012033
2023-02-07 13:33 (ALTech) Younkwang Jung Assigned To (ALTech) Younkwang Jung => (SW) Kerwin Chen
2025-04-07 10:00 (SW) Kerwin Chen Status acknowledged => closed
2025-04-07 10:00 (SW) Kerwin Chen Resolution open => not a bug
2025-04-07 10:00 (SW) Kerwin Chen Note Added: 0017418